View Issue Details

IDProjectCategoryView StatusLast Update
0001573K18X001.00 SKB SWANHWpublic2024-07-11 13:50
Reporter(ALTech) Sangmin Choi Assigned To(ALTech) Sangmin Choi Due Date2022-12-14 16:34
PriorityurgentSeveritys4-minorReproducibilityN/A
Status closedResolutionfixed 
Summary0001573: There are some devices from customer that bluetooth doesn't work.
DescriptionThere are some devices that the tombstone related with bluetooth is generated repeatedly.
So, I received these devices from SKB and I checked the bluetooth doesn't work.
I think it's related with HW.

I attached the logcat, dmesg and dumpsys bluetooth_manager.

Could you please check it?
TagsNo tags attached.
Attach Tags

Users monitoring this issue

User List (ALTech) Wooshin Kang , (HW) Sean Chiang , (PM) Dennis Lu , (Sales) Miya Wang

Activities

(ALTech) Sangmin Choi

2022-12-08 15:41

developer  

bluetooth_logcat.txt (3,495,678 bytes)
bluetooth_dmesg.txt (30,079 bytes)   
adb shell
BFX-UA300:/ # dmesg
[    3.570103] [vpu kernel driver] request irq:18
[    3.570106] [vpu kernel driver] register space [mem 0xf7000000-0xf73fffff]
[    3.570207] [vpu kernel driver] vpu:h1 probe ok
[    3.570360] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    3.570363] register card failed (-517)
[    3.570397] [vpu kernel driver] vpu probe enter
[    3.570400] [vpu kernel driver] find vpu device vxg
[    3.570407] [vpu kernel driver] request irq:19
[    3.570409] [vpu kernel driver] register space [mem 0xf7600000-0xf77fffff]
[    3.570466] [vpu kernel driver] vpu:vxg probe ok
[    3.570547] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    3.570549] register card failed (-517)
[    3.591264] [zsp kernel driver] zsp probe enter
[    3.591287] [zsp kernel driver] register space [mem 0xf7c00000-0xf7c7ffff]
[    3.591378] [zsp kernel driver] zsp:zsp probe ok
[    3.591530] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    3.591533] register card failed (-517)
[    3.713259] ueventd: Unable to read config file '/odm/ueventd.rc': open() failed: No such file or directory
[    3.723378] ueventd: Parsing file /ueventd.sequoia.rc...
[    3.728891] ueventd: Unable to read config file '/ueventd.sequoia.rc': open() failed: No such file or directory
[    3.936755] ueventd: Coldboot took 0.196 seconds
[    3.963823] Registered swp emulation handler
[    3.985618] file system registered
[    4.014485] logd.auditd: start
[    4.019270] logd.klogd: 4012832600
[    4.030667] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts:
[    4.043444] EXT4-fs (mmcblk0p21): Ignoring removed nomblk_io_submit option
[    4.057658] EXT4-fs (mmcblk0p21): mounted filesystem with ordered data mode. Opts: errors=remount-ro,nomblk_io_submit
[    4.165094] e2fsck: e2fsck 1.44.4 (18-Aug-2018)\x0a
[    4.171404] e2fsck: /dev/block/by-name/cache: clean, 30/62976 files, 8494/251904 blocks\x0a
[    4.183514] EXT4-fs (mmcblk0p21): mounted filesystem with ordered data mode. Opts:
[    4.193101] EXT4-fs (mmcblk0p22): Ignoring removed nomblk_io_submit option
[    4.206110] EXT4-fs (mmcblk0p22): recovery complete
[    4.211691] EXT4-fs (mmcblk0p22): mounted filesystem with ordered data mode. Opts: errors=remount-ro,nomblk_io_submit
[    4.292297] Synaptics PVR init. Version 201803121700
[    4.297529] pvr: clockspeed:          660222222
[    4.302207] pvr: register base:       0xf7980000
[    4.306991] pvr: register size:       0x80000
[    4.311490] pvr: irq:                 20
[    4.315536] pvr: need alloc from dma zone: true.
[    4.318798] e2fsck: e2fsck 1.44.4 (18-Aug-2018)\x0a
[    4.318820] e2fsck: Pass 1: Checking inodes, blocks, and sizes\x0a
[    4.318826] e2fsck: Pass 2: Checking directory structure\x0a
[    4.318830] e2fsck: Pass 3: Checking directory connectivity\x0a
[    4.318835] e2fsck: Pass 4: Checking reference counts\x0a
[    4.318840] e2fsck: Pass 5: Checking group summary information\x0a
[    4.318845] e2fsck: /dev/block/by-name/btv_home: 183/6400 files (14.8% non-contiguous), 8468/25600 blocks\x0a
[    4.319737] EXT4-fs (mmcblk0p22): Ignoring removed nomblk_io_submit option
[    4.332413] EXT4-fs (mmcblk0p22): mounted filesystem with ordered data mode. Opts: nodelalloc,nomblk_io_submit,errors=panic
[    4.392860] PVR_K:  187: Read BVNC 22.46.54.330 from HW device registers
[    4.399829] PVR_K:  187: RGX Device registered with BVNC 22.46.54.330
[    4.406862] pvrsrvkm f7980000.gpu: opp[1/2]: (400000000 Hz, 0 uV)
[    4.413159] pvrsrvkm f7980000.gpu: opp[2/2]: (700000000 Hz, 0 uV)
[    4.420671] pvr: set freq 400000000, cpm count 0, current freq 400000000
[    4.421367] [drm] Initialized pvr 1.11.5516664 20170530 on minor 0
[    4.428013] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    4.436413] register card failed (-517)
[    4.454682] snd_soc_ad8xxxx: loading out-of-tree module taints kernel.
[    4.455390] ad8xxxx_i2c_probe---------------1026
[    4.455402] ad8xxxx_parse_dt ad83586b_powerdown_pin = 499!
[    4.455406] ad8xxxx_parse_dt ad82584f_powerdown_pin = 448!
[    4.455419] ad8xxxx_i2c_probe---------------1053 probe end
[    4.456454] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    4.456459] register card failed (-517)
[    4.495772] wa206829b_parse_dt reset_pin = 507!
[    4.495780] wa206829b_parse_dt a113x_pin = 448!
[    4.495784] wa206829b_parse_dt powerdown_pin = 499!
[    4.495977] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered
[    4.495981] register card failed (-517)
[    4.524379] cx2081x_i2c_probe
[    4.524511] cx2081x_i2c_probe reset 50ms
[    4.546083] fsck.f2fs: Info: Fix the reported corruption.\x0a
[    4.546101] fsck.f2fs: \x09Info: No support kernel version!\x0a
[    4.546108] fsck.f2fs: Info: Segments per section = 1\x0a
[    4.546115] fsck.f2fs: Info: Sections per zone = 1\x0a
[    4.546122] fsck.f2fs: Info: sector size = 512\x0a
[    4.546129] fsck.f2fs: Info: total sectors = 8396800 (4100 MB)\x0a
[    4.546135] fsck.f2fs: Info: MKFS version\x0a
[    4.546143] fsck.f2fs:   "4.9.178 #1 SMP PREEMPT Tue Aug 16 10:55:10 CST 2022"\x0a
[    4.546150] fsck.f2fs: Info: FSCK version\x0a
[    4.546157] fsck.f2fs:   from "4.9.178 #1 SMP PREEMPT Tue Aug 16 09:59:39 CST 2022"\x0a
[    4.547905] F2FS-fs (mmcblk0p23): Reduce reserved blocks for root = 1944
[    4.552737] F2FS-fs (mmcblk0p23): Found nat_bits in checkpoint
[    4.586027] F2FS-fs (mmcblk0p23): Mounted with checkpoint version = 3154f326
[    4.596719] init: 7 output lines suppressed due to ratelimiting
[    4.617888] [amp_driver] Start open amp driver!
[    4.626511] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI tlv320adc3101-hifi not registered
[    4.626515] register card failed (-517)
[    4.650337] AutoPush Disabled using fastlogo.ta : 0x00000000
[    4.652047] 0-0033 supply vled not found, using dummy regulator
[    4.653113] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI tlv320adc3101-hifi not registered
[    4.653117] register card failed (-517)
[    4.653285] 0-0030 supply vled not found, using dummy regulator
[    4.653393] lp50xx 0-0030: Led lp5036:led0_mod renamed to lp5036:led0_mod_1 due to name collision
[    4.653447] lp50xx 0-0030: Led lp5036:led1_mod renamed to lp5036:led1_mod_1 due to name collision
[    4.653507] lp50xx 0-0030: Led lp5036:led2_mod renamed to lp5036:led2_mod_1 due to name collision
[    4.653572] lp50xx 0-0030: Led lp5036:led3_mod renamed to lp5036:led3_mod_1 due to name collision
[    4.653628] lp50xx 0-0030: Led lp5036:led4_mod renamed to lp5036:led4_mod_1 due to name collision
[    4.653684] lp50xx 0-0030: Led lp5036:led5_mod renamed to lp5036:led5_mod_1 due to name collision
[    4.653735] lp50xx 0-0030: Led lp5036:led6_mod renamed to lp5036:led6_mod_1 due to name collision
[    4.653793] lp50xx 0-0030: Led lp5036:led7_mod renamed to lp5036:led7_mod_1 due to name collision
[    4.653845] lp50xx 0-0030: Led lp5036:led8_mod renamed to lp5036:led8_mod_1 due to name collision
[    4.653902] lp50xx 0-0030: Led lp5036:led9_mod renamed to lp5036:led9_mod_1 due to name collision
[    4.654405] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI tlv320adc3101-hifi not registered
[    4.654409] register card failed (-517)
[    4.677465] type=1400 audit(1234567894.670:6): avc: denied { write } for comm="ledd" name="vendor" dev="mmcblk0p23" ino=67 scontext=u:r:ledd:s0 tcontext=u:object_r:vendor_data_file:s0 tclass=dir permissive=0
[    4.705598] vdc: Waited 0ms for vold
[    4.746441] lp50xx 0-0028: i2c_read_by_addr: read transfer error -121
[    4.746448] lp50xx 0-0028: lp50xx_probe: final client->addr=0x28
[    4.746627] 0-0028 supply vled not found, using dummy regulator
[    4.749289] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI tlv320adc3101-hifi not registered
[    4.749296] register card failed (-517)
[    4.756682] [amp_driver] amp_driver_open ok
[    4.756859] [amp_driver] amp_driver_mmap ok
[    4.767416] fscrypt: AES-256-CTS-CBC using implementation "cts(cbc-aes-ce)"
[    4.771121] aw210xx driver version v1.0.0
[    4.771191] aw210xx_i2c_probe: enter
[    4.771201] aw210xx 0-0034: init I2c for AW21036
[    4.771378] aw210xx_read_chipid: Match AW210XX, chip id is 0x18
[    4.771444] aw210xx 0-0034: aw210xx_read_version:failed to read version: -121
[    4.771448] aw210xx 0-0034: aw210xx_i2c_probe: aw210xx_read_version failed ret=-5
[    4.772653] aw210xx: probe of 0-0034 failed with error -5
[    4.772702] aw210xx_i2c_probe: enter
[    4.772711] aw210xx 0-0036: init I2c for AW21036
[    4.772860] aw210xx_read_chipid: Match AW210XX, chip id is 0x18
[    4.772922] aw210xx 0-0036: aw210xx_read_version:failed to read version: -121
[    4.772927] aw210xx 0-0036: aw210xx_i2c_probe: aw210xx_read_version failed ret=-5
[    4.772976] aw210xx: probe of 0-0036 failed with error -5
[    4.773018] aw210xx_i2c_probe: enter
[    4.773024] aw210xx 0-0038: init I2c for AW21024
[    4.773140] aw210xx_read_chipid: Match AW210XX, chip id is 0x18
[    4.773189] aw210xx 0-0038: aw210xx_read_version:failed to read version: -121
[    4.773193] aw210xx 0-0038: aw210xx_i2c_probe: aw210xx_read_version failed ret=-5
[    4.773295] aw210xx: probe of 0-0038 failed with error -5
[    4.797756] adc3101_i2c_probe enter
[    4.807878] adc3101_i2c_probe pull high reset
[    4.848927] fscrypt: AES-256-XTS using implementation "xts-aes-ce"
[    4.885904] apexd: Marking APEXd as starting
[    4.887024] apexd: Scanning /system/apex for embedded keys
[    4.888217] apexd: Scanning /product/apex for embedded keys
[    4.888247] apexd: ... does not exist. Skipping
[    4.888269] apexd: Populating APEX database from mounts...
[    4.888445] apexd: Failed to walk /product/apex : Can't open /product/apex for reading : No such file or directory
[    4.888885] apexd: Found "/apex/com.android.tzdata@290000000"
[    4.888942] apexd: Found "/apex/com.android.runtime@1"
[    4.889084] apexd: 2 packages restored.
[    4.889127] apexd: Scanning /data/apex/sessions looking for sessions to be activated.
[    4.952974] vdc: Waited 0ms for vold
[    4.957904] failed to get differential_pair, set it default
[    4.957909] failed to get slot_number, set it default
[    4.957912] adc3101_i2c_probe i2c:        (ptrval)
[    4.957929] adc3101_i2c_probe 18 done
[    4.958389] cx2081x_codec_probe
[    4.963296] adc3101_codec_probe enter
[    4.964219] adc3101_codec_probe done...
[    4.965935] ad8xxxx_probe
[    5.046420] ad8xxxx 0-0035: ad8xxxx_i2c_read_by_addr: read transfer error -121
[    5.046425] ad8xxxx_probe ESMT AMP does not connect
[    5.056564] [amp_driver] vpp bcm shed qid:0 info:0 0
[    5.056570] [amp_driver] vpp bcm shed qid:1 info:0 0
[    5.056573] [amp_driver] vpp bcm shed qid:2 info:0 0
[    5.056576] [amp_driver] vpp bcm shed qid:3 info:0 0
[    5.056578] [amp_driver] vpp bcm shed qid:4 info:0 0
[    5.056581] [amp_driver] vpp bcm shed qid:5 info:0 0
[    5.156466] wa206829b 0-002a: wa206829b_i2c_read_by_addr: read transfer error -121
[    5.156471] wa206829b_probe: ESMT AMP IC does not connect, so default enable Wellang
[    5.156473] wa206829b_probe: Wellang AMP IC connect.
[    5.156616] syna-berlin-asoc soc:berlin-asoc: snd-soc-dummy-dai <-> soc:berlin-i2si0 mapping ok
[    5.156693] syna-berlin-asoc soc:berlin-asoc: cx2081x-aif <-> soc:berlin-i2si1 mapping ok
[    5.156697] [adc3101_set_dai_fmt]:slot_number=0
[    5.156726] wa206829b_init reguest reset_pin at boot
[    5.156736] wa206829b_init reset_pin set to High
[    5.157743] wa206829b_init a113x_pin set to High
[    5.158749] wa206829b_init powerdown_pin set to High
[    5.158761] wa206829b_init mute node created
[    5.158763] [0]=section number 7
[    5.158766] config_reg_section:size=8
[    5.159654] config_coef_section:size=60
[    5.163017] syna-berlin-asoc soc:berlin-asoc: tlv320adc3101-hifi <-> soc:berlin-i2si0 mapping ok
[    5.163099] syna-berlin-asoc soc:berlin-asoc: ad8xxxx <-> soc:berlin-outdai mapping ok
[    5.163180] syna-berlin-asoc soc:berlin-asoc: wa206829b <-> soc:berlin-outdai mapping ok
[    5.175546] config_reg_section:size=36
[    5.177442] PVR_K:  294: RGX Firmware image 'rgx.fw.22.46.54.330' loaded
[    5.178646] config_coef_section:size=160
[    5.192277] config_reg_section:size=26
[    5.193521] config_reg_section:size=12
[    5.194092] config_reg_section:size=12
[    5.194666] [1]=section number 7
[    5.194668] config_reg_section:size=8
[    5.195046] config_coef_section:size=60
[    5.200170] config_reg_section:size=36
[    5.201882] config_coef_section:size=160
[    5.217197] config_reg_section:size=26
[    5.221340] config_reg_section:size=12
[    5.222227] config_reg_section:size=12
[    5.222871] [2]=section number 7
[    5.222875] config_reg_section:size=8
[    5.223322] config_coef_section:size=60
[    5.230159] config_reg_section:size=36
[    5.231922] config_coef_section:size=160
[    5.241725] type=1400 audit(1234567894.670:6): avc: denied { write } for comm="ledd" name="vendor" dev="mmcblk0p23" ino=67 scontext=u:r:ledd:s0 tcontext=u:object_r:vendor_data_file:s0 tclass=dir permissive=0
[    5.241753] type=1400 audit(1670484148.219:7): avc: denied { setattr } for comm="init" name="/" dev="mmcblk0p21" ino=2 scontext=u:r:vendor_init:s0 tcontext=u:object_r:cache_file:s0 tclass=dir permissive=0
[    5.242078] type=1400 audit(1670484148.219:7): avc: denied { setattr } for comm="init" name="/" dev="mmcblk0p21" ino=2 scontext=u:r:vendor_init:s0 tcontext=u:object_r:cache_file:s0 tclass=dir permissive=0
[    5.242097] type=1400 audit(1670484148.219:8): avc: denied { search } for comm="init" name="/" dev="mmcblk0p21" ino=2 scontext=u:r:vendor_init:s0 tcontext=u:object_r:cache_file:s0 tclass=dir permissive=0
[    5.242400] type=1400 audit(1670484148.219:8): avc: denied { search } for comm="init" name="/" dev="mmcblk0p21" ino=2 scontext=u:r:vendor_init:s0 tcontext=u:object_r:cache_file:s0 tclass=dir permissive=0
[    5.242422] type=1400 audit(1670484148.219:9): avc: denied { setattr } for comm="init" name="btv_home" dev="mmcblk0p23" ino=120 scontext=u:r:vendor_init:s0 tcontext=u:object_r:system_data_file:s0 tclass=lnk_file permissive=0
[    5.245983] type=1400 audit(1670484148.219:10): avc: denied { setattr } for comm="init" name="btv_home" dev="mmcblk0p23" ino=120 scontext=u:r:vendor_init:s0 tcontext=u:object_r:system_data_file:s0 tclass=lnk_file permissive=0
[    5.247320] config_reg_section:size=26
[    5.248578] config_reg_section:size=12
[    5.249974] config_reg_section:size=12
[    5.250792] [3]=section number 7
[    5.250796] config_reg_section:size=8
[    5.251349] config_coef_section:size=60
[    5.256567] config_reg_section:size=36
[    5.258294] config_coef_section:size=160
[    5.272295] config_reg_section:size=26
[    5.273536] config_reg_section:size=12
[    5.274101] config_reg_section:size=12
[    5.274671] wa206829b_init: Mute to avoid boot pop noise
[    5.274673] config_reg_section:size=2
[    5.274771] config_reg_section:size=2
[    5.274867] config_reg_section:size=2
[    5.274963] config_reg_section:size=2
[    5.449679] VPP_CA_getVppTaUUID:65: loading recovery ta:1(vpp.ta)
[    5.449685] VPP_CA_Initialize:85: result:0
[    5.460240] VPP_CA_Initialize:102: TEEC_OpenSession:0 result:0
[    5.460330] [amp_driver] vpp init param info:face 0
[    5.882507] fts: fts partition is on /dev/block/by-name/fts, 0x400000 bytes
[    5.882514] fts: chunk: 0x4000
[    5.912374] fts: flash_ts_scan skiped 8 blocks
[    5.912381] fts: v21 loaded from 0x00050000
[    5.921128] fts: fts partition is on /dev/block/by-name/fts, 0x400000 bytes
[    5.921135] fts: chunk: 0x4000
[    5.930745] ci_hdrc ci_hdrc.0: remove, state 4
[    5.930770] usb usb3: USB disconnect, device number 1
[    5.931230] ci_hdrc ci_hdrc.0: USB bus 3 deregistered
[    5.954991] fts: flash_ts_scan skiped 8 blocks
[    5.954999] fts: v21 loaded from 0x00050000
[    5.963872] fts: fts partition is on /dev/block/by-name/fts, 0x400000 bytes
[    5.963879] fts: chunk: 0x4000
[    5.978760] fts: flash_ts_scan skiped 8 blocks
[    5.978767] fts: v21 loaded from 0x00050000
[    6.128159] read descriptors
[    6.128167] read strings
[    6.322117] [tsp kernel driver] Start open tsp driver!
[    6.322161] [tsp kernel driver] tsp_driver_open ok
[    6.322182] [tsp kernel driver] tsp_driver_mmap ok
[    7.349946] random: crng init done
[    7.349950] random: 6 urandom warning(s) missed due to ratelimiting
[    7.693908] [amp_driver] path 0 start\x0a
[    7.693948] [amp_driver] path 2 start\x0a
[    7.694728] [amp_driver] path 3 start\x0a
[    7.740434] [amp_driver] path 3 start\x0a
[    7.744310] [amp_driver] Start open amp driver!
[    7.744317] [amp_driver] amp driver reference count 2!
[    7.745416] wa206829b: store_mute not boot complete yet
[    8.010910] [amp_driver] path_id=3 not exist
[    8.048361] [amp_driver] path 3 start\x0a
[    8.086137] [amp_driver] Start open amp driver!
[    8.086145] [amp_driver] amp driver reference count 3!
[    8.447224] init: Received control message 'start' for 'bootanim' from pid: 290 (/system/bin/surfaceflinger)
[    9.701210] init: Service 'vendor.wireless_init' (pid 442) exited with status 1
[   10.003511] type=1400 audit(1670484151.969:51): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   10.003560] type=1400 audit(1670484152.979:52): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   10.005117] type=1400 audit(1670484152.979:52): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   10.005148] type=1400 audit(1670484152.979:53): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   10.418046] init: processing action (sys.sysctl.extra_free_kbytes=*) from (/init.rc:835)
[   10.421656] init: processing action (sys.sysctl.extra_free_kbytes=*) from (/system/etc/init/skb_btvd.rc:23)
[   10.424354] type=1400 audit(1670484152.979:53): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   10.424544] type=1400 audit(1670484153.399:54): avc: denied { write } for comm="init" name="swappiness" dev="proc" ino=14221 scontext=u:r:init:s0 tcontext=u:object_r:proc:s0 tclass=file permissive=0
[   10.424905] init: Command 'write /proc/sys/vm/swappiness 0' action=sys.sysctl.extra_free_kbytes=* (/system/etc/init/skb_btvd.rc:26) took 2ms and failed: Unable to write to file '/proc/sys/vm/swappiness': open() failed: Permission denied
[   11.015477] type=1400 audit(1670484153.399:54): avc: denied { write } for comm="init" name="swappiness" dev="proc" ino=14221 scontext=u:r:init:s0 tcontext=u:object_r:proc:s0 tclass=file permissive=0
[   11.015509] type=1400 audit(1670484153.989:55): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   11.015611] type=1400 audit(1670484153.989:55): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   11.015628] type=1400 audit(1670484153.989:56): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   11.896925] fxc **** rtl8211f_phy_fix addr 0x19 before fix = 0x862
[   11.897068] fxc **** rtl8211f_phy_fix addr 0x19 after fix = 0x862
[   11.897074] fxc **** rtl8211f_soft_reset enter
[   11.898957] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.736204] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.985464] ttyS1 - failed to request DMA
[   15.074907] type=1400 audit(1670484157.029:64): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   15.074939] type=1400 audit(1670484158.049:65): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   15.075032] type=1400 audit(1670484158.049:65): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   15.075049] type=1400 audit(1670484158.049:66): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   16.076932] mvneta f7b60000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   16.076964] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   16.083613] type=1400 audit(1670484158.049:66): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   16.083653] type=1400 audit(1670484159.059:67): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   16.083775] type=1400 audit(1670484159.059:67): avc: denied { module_request } for comm="pxMlrManager" kmod="netdev-wlan0" scontext=u:r:px_mlr:s0 tcontext=u:r:kernel:s0 tclass=system permissive=0
[   16.083797] type=1400 audit(1670484159.059:68): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   16.436958] init: processing action (sys.sysctl.tcp_def_init_rwnd=*) from (/init.rc:839)
[   16.677254] init: Service 'bootanim' (pid 291) exited with status 0
[   16.810216] init: processing action (sys.boot_completed=1) from (/init.rc:829)
[   16.810293] init: processing action (sys.boot_completed=1) from (/vendor/etc/init/hw/init.sequoia.rc:206)
[   16.824089] gpio-keypad gpio_keypad: vol_up key(115) registed.
[   16.824095] gpio-keypad gpio_keypad: vol_down key(114) registed.
[   16.824098] gpio-keypad gpio_keypad: ch_down key(403) registed.
[   16.824101] gpio-keypad gpio_keypad: ch_up key(402) registed.
[   16.824104] gpio-keypad gpio_keypad: sound_field_mode key(426) registed.
[   16.824107] gpio-keypad gpio_keypad: bt_pair key(189) registed.
[   16.824110] gpio-keypad gpio_keypad: mic_mute key(188) registed.
[   16.824113] gpio-keypad gpio_keypad: power key(116) registed.
[   16.824598] input: gpio_keypad as /devices/platform/gpio_keypad/input/input1
[   16.834394] sdcardfs version 2.0
[   16.834402] sdcardfs: dev_name -> /data/media
[   16.834404] sdcardfs: options -> fsuid=1023,fsgid=1023,multiuser,derive_gid,default_normal,unshared_obb,mask=6,userid=0,gid=1015
[   16.834410] sdcardfs: mnt -> 00000000c158532f
[   16.834485] sdcardfs: mounted on top of /data/media type f2fs
[   16.835715] Remount options were mask=23,gid=9997 for vfsmnt 000000004557658e.
[   16.835724] sdcardfs : options - debug:1
[   16.835727] sdcardfs : options - gid:9997
[   16.835728] sdcardfs : options - mask:23
[   16.836348] Remount options were mask=7,gid=9997 for vfsmnt 00000000448d8c3e.
[   16.836352] sdcardfs : options - debug:1
[   16.836354] sdcardfs : options - gid:9997
[   16.836355] sdcardfs : options - mask:7
[   16.836999] Remount options were mask=7,gid=9997 for vfsmnt 00000000c05bf275.
[   16.837004] sdcardfs : options - debug:1
[   16.837006] sdcardfs : options - gid:9997
[   16.837007] sdcardfs : options - mask:7
[   16.838283] atvr_init: succeeded creating misc device snd_atvr_pcm
[   16.838330] atvr_init: succeeded creating misc device snd_atvr_adpcm
[   16.838377] atvr_init: succeeded creating misc device snd_atvr_msbc
[   16.918042] init: processing action (sys.boot_completed=1) from (/vendor/etc/init/hw/init.sequoia.rc:329)
[   16.920273] init: starting service 'vendor.edidinfo'...
[   16.927558] init: processing action (sys.boot_completed=1) from (/system/etc/init/audiomirror.rc:1)
[   16.931752] init: starting service 'audiomirrord'...
[   16.939246] type=1400 audit(1670484159.059:68): avc: denied { sys_module } for comm="pxMlrManager" capability=16 scontext=u:r:px_mlr:s0 tcontext=u:r:px_mlr:s0 tclass=capability permissive=0
[   16.939273] type=1400 audit(1670484177.000:69): avc: denied { dac_override } for comm="skb_rdate" capability=1 scontext=u:r:skb_rdate:s0 tcontext=u:r:skb_rdate:s0 tclass=capability permissive=0
[   16.941886] init: processing action (sys.boot_completed=1 && sys.logbootcomplete=1) from (/system/etc/init/bootstat.rc:78)
[   16.942578] init: starting service 'exec 14 (/system/bin/bootstat --record_boot_complete --record_boot_reason --record_time_since_factory_reset -l)'...
[   17.186690] zram: setup backing device /dev/block/loop0
[   17.188092] zram0: detected capacity change from 0 to 1073741824
[   17.214544] mkswap: Swapspace size: 1048572k, UUID=a1e21a0f-a0a0-4335-b59b-9921a916b9e3
[   17.215816] Adding 1048572k swap on /dev/block/zram0.  Priority:-1 extents:1 across:1048572k SS
[   17.450487] audit: audit_lost=1 audit_rate_limit=5 audit_backlog_limit=64
[   17.450493] audit: rate limit exceeded
[   17.578386] wa206829b: set BootComplete true
[   17.578390] wa206829b: wa206829b_unmute_all
[   17.578394] config_reg_section:size=2
[   17.578778] config_reg_section:size=2
[   17.578903] config_reg_section:size=2
[   17.579041] config_reg_section:size=2
[   18.786778] input: virtual-search as /devices/virtual/input/input2
[   19.144591] F2FS-fs (mmcblk0p23): Retry to write fsync mark: ino=5287, idx=14a7
[   19.467807] binder: release 917:1245 transaction 20675 in, still active
[   19.467815] binder: send failed reply for transaction 20675 to 329:1284
[   20.381207] xenon-sdhci f7aa0000.sdhci: Timing issue might occur in DDR mode
[   20.687456] type=1400 audit(1670484177.500:80): avc: denied { sys_ptrace } for comm="killall" capability=19 scontext=u:r:qsm_server:s0 tcontext=u:r:qsm_server:s0 tclass=capability permissive=0 duplicate messages suppressed
[   20.687488] type=1400 audit(1670484180.730:670): avc: denied { search } for comm="ScionFrontendAp" name="com.google.android.gms" dev="mmcblk0p23" ino=3953 scontext=u:r:skbframework_app:s0 tcontext=u:object_r:privapp_data_file:s0:c512,c768 tclass=dir permissive=0
[   20.766828] [amp_driver] path_id=3 not exist
[   20.804350] [amp_driver] path 3 start\x0a
[   21.396313] audit: audit_lost=590 audit_rate_limit=5 audit_backlog_limit=64
[   21.396318] audit: rate limit exceeded
[   21.483341] type=1400 audit(1670484180.730:671): avc: denied { search } for comm="ScionFrontendAp" name="com.google.android.gms" dev="mmcblk0p23" ino=3953 scontext=u:r:skbframework_app:s0 tcontext=u:object_r:privapp_data_file:s0:c512,c768 tclass=dir permissive=0
[   21.493873] type=1400 audit(1670484181.440:672): avc: granted { read } for comm="b.btv.msgbroker" name="somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   21.494674] type=1400 audit(1670484181.440:672): avc: granted { read } for comm="b.btv.msgbroker" name="somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   21.496676] type=1400 audit(1670484181.440:673): avc: granted { read open } for comm="b.btv.msgbroker" path="/proc/sys/net/core/somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   21.496827] type=1400 audit(1670484181.440:673): avc: granted { read open } for comm="b.btv.msgbroker" path="/proc/sys/net/core/somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   21.507030] type=1400 audit(1670484181.440:674): avc: granted { getattr } for comm="b.btv.msgbroker" path="/proc/sys/net/core/somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   21.571870] init: processing action (sys.dscp.run=1) from (/system/etc/init/skb_script.rc:23)
[   21.572545] init: starting service 'dscp'...
[   21.788740] init: Service 'dscp' (pid 2425) exited with status 0
[   22.559808] apexd: Can't open /product/apex for reading : No such file or directory
[   22.749022] type=1400 audit(1670484181.440:674): avc: granted { getattr } for comm="b.btv.msgbroker" path="/proc/sys/net/core/somaxconn" dev="proc" ino=28207 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:proc_net:s0 tclass=file app=com.skb.btv.msgbroker
[   22.752009] type=1400 audit(1670484182.680:677): avc: denied { read } for comm="lanet.hlsplayer" name="u:object_r:vendor_display_size_prop:s0" dev="tmpfs" ino=7351 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:vendor_display_size_prop:s0 tclass=file permissive=0 app=com.skplanet.hlsplayer
[   25.766263] type=1400 audit(1670484184.540:684): avc: denied { dac_read_search } for comm="HwBinder:391_2" capability=2 scontext=u:r:btvservice_hal:s0 tcontext=u:r:btvservice_hal:s0 tclass=capability permissive=0
[   25.771548] type=1400 audit(1670484185.810:685): avc: denied { read } for comm="pool-8-thread-1" name="u:object_r:vendor_default_prop:s0" dev="tmpfs" ino=7349 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:vendor_default_prop:s0 tclass=file permissive=0 app=com.google.android.katniss
[   25.889728] logd: logdr: UID=1002 GID=1002 PID=2822 n tail=50 logMask=8 pid=2184 start=0ns timeout=0ns
[   26.152516] logd: logdr: UID=1002 GID=1002 PID=2822 n tail=50 logMask=1 pid=2184 start=0ns timeout=0ns
[   26.229047] init: Received control message 'start' for 'gsid' from pid: 708 (system_server)
[   26.229630] init: starting service 'gsid'...
BFX-UA300:/ #
bluetooth_dmesg.txt (30,079 bytes)   
bluetooth_dumpsys_bluetooth_manager.txt (4,925 bytes)   
adb shell
BFX-UA300:/ # dumpsys bluetooth_manager
Bluetooth Status
  enabled: false
  state: BLE_TURNING_ON
  address: null
  name: UA300_8E0D
  time since enabled: 00:03:31.463

Enable log:
  12-08 16:24:58  Enabled  due to RESTARTED by android
  12-08 16:25:03  Enabled  due to RESTARTED by android
  12-08 16:25:09  Enabled  due to RESTARTED by android
  12-08 16:25:15  Enabled  due to RESTARTED by android
  12-08 16:25:20  Enabled  due to RESTARTED by android
  12-08 16:25:26  Enabled  due to RESTARTED by android
  12-08 16:25:31  Enabled  due to RESTARTED by android
  12-08 16:25:37  Enabled  due to RESTARTED by android
  12-08 16:25:43  Enabled  due to RESTARTED by android
  12-08 16:25:48  Enabled  due to RESTARTED by android
  12-08 16:25:54  Enabled  due to RESTARTED by android
  12-08 16:25:59  Enabled  due to RESTARTED by android
  12-08 16:26:05  Enabled  due to RESTARTED by android
  12-08 16:26:10  Enabled  due to RESTARTED by android
  12-08 16:26:16  Enabled  due to RESTARTED by android
  12-08 16:26:22  Enabled  due to RESTARTED by android
  12-08 16:26:27  Enabled  due to RESTARTED by android
  12-08 16:26:33  Enabled  due to RESTARTED by android
  12-08 16:26:38  Enabled  due to RESTARTED by android
  12-08 16:26:44  Enabled  due to RESTARTED by android
  12-08 16:26:54  Enabled  due to RESTARTED by android

Bluetooth crashed 0 times

0 BLE appsregistered


AdapterProperties
  Name: UA300_8E0D
  Address: null
  BluetoothClass: 200414
  ScanMode: Unknown Scan Mode 0
  ConnectionState: STATE_DISCONNECTED
  State: BLE_TURNING_ON
  MaxConnectedAudioDevices: 5
  A2dpOffloadEnabled: false
  Discovering: false
  DiscoveryEndMs: 0
  Bonded devices:
mSnoopLogSettingAtEnable = disable
mDefaultSnoopLogSettingAtEnable = null

AdapterState:
 total records=1
 rec[0]: time=12-08 16:26:59.930 processed=OffState org=OffState dest=TurningBleOnState what=3(0x3) BLE_TURN_ON
curState=TurningBleOnState

SilenceDeviceManager:
  Address            | Is silenced?

Profile: GattService
  mAdvertisingServiceUuids:
  mMaxScanFilters: 0

GATT Scanner Map
  Entries: 0

GATT Client Map
  Entries: 0

GATT Server Map
  Entries: 0

GATT Handle Map
  Entries: 0
  Requests: 0

Connection Events:
  None

Bond Events:
  Total Number of events: 0

A2DP State:
  TxQueue:
  Counts (enqueue/dequeue/readbuf)                        : 0 / 0 / 0
  Last update time ago in ms (enqueue/dequeue/readbuf)    : 0 / 0 / 0
  Frames per packet (total/max/ave)                       : 0 / 0 / 0
  Counts (flushed/dropped/dropouts)                       : 0 / 0 / 0
  Counts (max dropped)                                    : 0
  Last update time ago in ms (flushed/dropped)            : 0 / 0
  Counts (underflow)                                      : 0
  Bytes (underflow)                                       : 0
  Last update time ago in ms (underflow)                  : 0
  Enqueue deviation counts (overdue/premature)            : 0 / 0
  Enqueue overdue scheduling time in ms (total/max/ave)   : 0 / 0 / 0
  Enqueue premature scheduling time in ms (total/max/ave) : 0 / 0 / 0
  Dequeue deviation counts (overdue/premature)            : 0 / 0
  Dequeue overdue scheduling time in ms (total/max/ave)   : 0 / 0 / 0
  Dequeue premature scheduling time in ms (total/max/ave) : 0 / 0 / 0

A2DP Source State: Disabled

A2DP Sink State: Disabled

AVRCP Target Service not started

Bluetooth Config:
  Config Source: New file
  Devices loaded: 0
  File created/tagged: 2022-12-08 16:26:58
  File source: Empty

Bluetooth HF Client BTA Statistics

Bluetooth Wakelock Statistics:
  Is acquired                    : false
  Acquired/released count        : 0 / 0
  Acquired/released error count  : 0 / 0
  Last acquire/release error code: 0 / 0
  Last acquired time (ms)        : 0
  Acquired time min/max/avg (ms) : 0 / 0 / 0
  Total acquired time (ms)       : 0
  Total run time (ms)            : 262393

Bluetooth Memory Allocation Statistics:
  Total allocated/free/used counts : 49 / 11 / 38
  Total allocated/free/used octets : 264202 / 405 / 263797

Bluetooth Alarms Statistics:
  Total Alarms: 1

  Alarm : hci.startup_timer (SINGLE)
    Action counts (sched/resched/exec/cancel)      : 1 / 0 / 0 / 0
    Deviation counts (overdue/premature)           : 0 / 0
    Time in ms (since creation/interval/remaining) : 2404 / 11000 / 8596
    Overdue scheduling time in ms (total/max/avg)  : 0 / 0 / 0
    Premature scheduling time in ms (total/max/avg): 0 / 0 / 0

Hearing Aid Manager:
  Hearing Aid Audio HAL:
    Counts (underflow)                                      : 0
    Bytes (underflow)                                       : 0
    Last update time ago in ms (underflow)                  : 0


connection_manager state:

	no Low Energy connection attempts

BT Quality Report Events:
Event queue is empty.
--- BEGIN:BTSNOOP_LOG_SUMMARY (0 bytes in) ---
AgAAAAAAAAAA
--- END:BTSNOOP_LOG_SUMMARY ---
BFX-UA300:/ #

(ALTech) Sangmin Choi

2022-12-08 15:42

developer   ~0011433

Hi, Jacky,

Could you please check this ticket?

Thank you.

(SW) Jacky Chiang

2022-12-12 10:34

manager   ~0011460

Hi Sangmin,

Could you kindly let us know what are the bluetooth devices in trouble ?

Thanks,
Jacky

(ALTech) Sangmin Choi

2022-12-12 10:44

developer   ~0011461

Hi, Jacky,

This issue is not related with other bluetooth devices.
The bluetooth of STB didn't work.

I suspicious it's hardware issue, but I'm not sure yet.

Thank you.

(SW) Jacky Chiang

2022-12-12 17:14

manager   ~0011468

Hi Sangmin,

   Could you pickup one of these defective STBs and program the current F/W to check if BT function is working or not?

If BT function is not working, I think we shall go RMA process for further analysis.

I added our PM to this case.

Thanks,
Jacky

(ALTech) Sangmin Choi

2022-12-13 10:48

developer   ~0011471

Hi, Lonnie,

Could you please check the devices which doesn't work?
And, also please proceed the RMA process.

Thank you.

(ALTech) Sun Choul Joung

2022-12-19 11:02

developer   ~0011512

HI Jacky.
 The BT problem hw check result .
 ( VDDIO9 Block for GPIO issue )
 -> USB HUB IC IS not recognized due to the SOC to hub ic reset GPIO(014) issue .

Thank you.
joung

Issue History

Date Modified Username Field Change
2022-12-08 15:41 (ALTech) Sangmin Choi New Issue
2022-12-08 15:41 (ALTech) Sangmin Choi Status new => assigned
2022-12-08 15:41 (ALTech) Sangmin Choi Assigned To => (SW) Jacky Chiang
2022-12-08 15:41 (ALTech) Sangmin Choi File Added: bluetooth_logcat.txt
2022-12-08 15:41 (ALTech) Sangmin Choi File Added: bluetooth_dmesg.txt
2022-12-08 15:41 (ALTech) Sangmin Choi File Added: bluetooth_dumpsys_bluetooth_manager.txt
2022-12-08 15:42 (ALTech) Sangmin Choi Note Added: 0011433
2022-12-12 10:07 (SW) Jim Chen Assigned To (SW) Jacky Chiang => (SW) Jim Chen
2022-12-12 10:34 (SW) Jacky Chiang Note Added: 0011460
2022-12-12 10:44 (ALTech) Sangmin Choi Note Added: 0011461
2022-12-12 17:14 (SW) Jacky Chiang Note Added: 0011468
2022-12-12 17:16 (SW) Jacky Chiang Issue Monitored: (PM) Dennis Lu
2022-12-12 17:16 (SW) Jacky Chiang Issue Monitored: (HW) Sean Chiang
2022-12-12 17:17 (SW) Jacky Chiang Issue Monitored: (Sales) Miya Wang
2022-12-12 17:17 (SW) Jacky Chiang Assigned To (SW) Jim Chen => (ALTech) Sangmin Choi
2022-12-13 10:48 (ALTech) Sangmin Choi Note Added: 0011471
2022-12-13 10:49 (ALTech) Sangmin Choi Assigned To (ALTech) Sangmin Choi => (ALTech) Lonnie Kim
2022-12-13 10:49 (ALTech) Sangmin Choi Issue Monitored: (ALTech) Wooshin Kang
2022-12-19 11:02 (ALTech) Sun Choul Joung Note Added: 0011512
2024-07-11 13:50 (ALTech) Sangmin Choi Assigned To (ALTech) Lonnie Kim => (ALTech) Sangmin Choi
2024-07-11 13:50 (ALTech) Sangmin Choi Status assigned => closed
2024-07-11 13:50 (ALTech) Sangmin Choi Resolution open => fixed